[INFO] cloning repository https://github.com/randoguyname/bitstream-autocorrelation
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/randoguyname/bitstream-autocorrelation" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frandoguyname%2Fbitstream-autocorrelation", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frandoguyname%2Fbitstream-autocorrelation'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d64867144507333f08ff0fe0970850b5c0197f7f
[INFO] testing randoguyname/bitstream-autocorrelation against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frandoguyname%2Fbitstream-autocorrelation" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/randoguyname/bitstream-autocorrelation
[INFO] finished tweaking git repo https://github.com/randoguyname/bitstream-autocorrelation
[INFO] tweaked toml for git repo https://github.com/randoguyname/bitstream-autocorrelation written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/randoguyname/bitstream-autocorrelation on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/randoguyname/bitstream-autocorrelation already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded minimp3 v0.5.2
[INFO] [stderr]   Downloaded creak v0.3.0
[INFO] [stderr]   Downloaded slice-ring-buffer v0.3.4
[INFO] [stderr]   Downloaded minimp3-sys v0.3.2
[INFO] [stderr]   Downloaded claxon v0.4.3
[INFO] [stderr]   Downloaded objc2-core-audio-types v0.3.2
[INFO] [stderr]   Downloaded objc2-core-audio v0.3.2
[INFO] [stderr]   Downloaded toml_parser v1.0.8+spec-1.1.0
[INFO] [stderr]   Downloaded coreaudio-rs v0.13.0
[INFO] [stderr]   Downloaded alsa v0.10.0
[INFO] [stderr]   Downloaded objc2-avf-audio v0.3.2
[INFO] [stderr]   Downloaded cpal v0.17.1
[INFO] [stderr]   Downloaded objc2-audio-toolbox v0.3.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 650eadf3853403ca82de4d4b239fdefbf1dcbce352ec326fd4b5a0b9204912c7
[INFO] running `Command { std: "docker" "start" "-a" "650eadf3853403ca82de4d4b239fdefbf1dcbce352ec326fd4b5a0b9204912c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "650eadf3853403ca82de4d4b239fdefbf1dcbce352ec326fd4b5a0b9204912c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "650eadf3853403ca82de4d4b239fdefbf1dcbce352ec326fd4b5a0b9204912c7", kill_on_drop: false }`
[INFO] [stdout] 650eadf3853403ca82de4d4b239fdefbf1dcbce352ec326fd4b5a0b9204912c7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6f2206e5c8669c16b6c19df50a503f7897c6bd19e2119a0acf17cb48d330644a
[INFO] running `Command { std: "docker" "start" "-a" "6f2206e5c8669c16b6c19df50a503f7897c6bd19e2119a0acf17cb48d330644a", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling iana-time-zone v0.1.65
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling csv-core v0.1.13
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling syn v2.0.116
[INFO] [stderr]    Compiling chrono v0.4.43
[INFO] [stderr]    Compiling csv v1.4.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling bitstream-autocorrelation v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.33s
[INFO] running `Command { std: "docker" "inspect" "6f2206e5c8669c16b6c19df50a503f7897c6bd19e2119a0acf17cb48d330644a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6f2206e5c8669c16b6c19df50a503f7897c6bd19e2119a0acf17cb48d330644a", kill_on_drop: false }`
[INFO] [stdout] 6f2206e5c8669c16b6c19df50a503f7897c6bd19e2119a0acf17cb48d330644a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6ca5ef437b4e68446704419e13ac63161e41455fb9760a34758b6edfb5484051
[INFO] running `Command { std: "docker" "start" "-a" "6ca5ef437b4e68446704419e13ac63161e41455fb9760a34758b6edfb5484051", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling alsa-sys v0.3.1
[INFO] [stderr]    Compiling tinyvec_macros v0.1.1
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling ogg v0.8.0
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling cpal v0.17.1
[INFO] [stderr]    Compiling claxon v0.4.3
[INFO] [stderr]    Compiling hound v3.5.1
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling syn v2.0.116
[INFO] [stderr]    Compiling dasp_sample v0.11.0
[INFO] [stderr]    Compiling tinyvec v1.10.0
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling lewton v0.10.2
[INFO] [stderr]    Compiling minimp3-sys v0.3.2
[INFO] [stderr]    Compiling slice-ring-buffer v0.3.4
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling alsa v0.10.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling minimp3 v0.5.2
[INFO] [stderr]    Compiling creak v0.3.0
[INFO] [stderr]    Compiling bitstream-autocorrelation v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 20.09s
[INFO] running `Command { std: "docker" "inspect" "6ca5ef437b4e68446704419e13ac63161e41455fb9760a34758b6edfb5484051", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6ca5ef437b4e68446704419e13ac63161e41455fb9760a34758b6edfb5484051", kill_on_drop: false }`
[INFO] [stdout] 6ca5ef437b4e68446704419e13ac63161e41455fb9760a34758b6edfb5484051
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 23fe70f21bd94653c8b541e70b6a9d0c2a448ad96cd0aabc54e0a85cb50c7ad1
[INFO] running `Command { std: "docker" "start" "-a" "23fe70f21bd94653c8b541e70b6a9d0c2a448ad96cd0aabc54e0a85cb50c7ad1", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/bitstream_autocorrelation-ab3726960a4f26b0)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] [2m2026-06-13T09:50:27.388487Z[0m [32m INFO[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m test-files/test-246-guitar.mp3 has sample rate 44100
[INFO] [stdout] [2m2026-06-13T09:50:27.390193Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.391214Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 538 is a subharmonic of 80 (0.6435547 vs 0.64160156)
[INFO] [stdout] [2m2026-06-13T09:50:27.391916Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 76.89746 at 44100hZ = 573.49097hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.391944Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.391958Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.392359Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 93 with 182 (0.0703125 vs 0.9453125)
[INFO] [stdout] [2m2026-06-13T09:50:27.392379Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 182 with 355 (0.9453125 vs 0.9921875)
[INFO] [stdout] [2m2026-06-13T09:50:27.392960Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 355.13217 at 44100hZ = 124.179115hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.392982Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.392997Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 124.179115
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.393400Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 85 with 176 (0.05078125 vs 0.9667969)
[INFO] [stdout] [2m2026-06-13T09:50:27.393421Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 176 with 357 (0.9667969 vs 0.99121094)
[INFO] [stdout] [2m2026-06-13T09:50:27.393435Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 715 is a subharmonic of 357 (0.99316406 vs 0.99121094)
[INFO] [stdout] [2m2026-06-13T09:50:27.394032Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 357.63272 at 44100hZ = 123.31087hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.394057Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 1
[INFO] [stdout] [2m2026-06-13T09:50:27.394070Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 123.31087
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.394357Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 358 is a subharmonic of 178 (0.98828125 vs 0.9824219)
[INFO] [stdout] [2m2026-06-13T09:50:27.394377Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 714 is a subharmonic of 178 (0.9892578 vs 0.98828125)
[INFO] [stdout] [2m2026-06-13T09:50:27.394411Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 357 is a subharmonic of 178 (0.9902344 vs 0.9892578)
[INFO] [stdout] [2m2026-06-13T09:50:27.395006Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.57811 at 44100hZ = 246.95076hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.395029Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (2)
[INFO] [stdout] [2m2026-06-13T09:50:27.395047Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.9902344
[INFO] [stdout] [2m2026-06-13T09:50:27.395061Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [124.179115, 123.31087, 246.95076]
[INFO] [stdout] [2m2026-06-13T09:50:27.395076Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 2
[INFO] [stdout] [2m2026-06-13T09:50:27.395087Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 124.179115
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.395475Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 221 is a subharmonic of 43 (0.50390625 vs 0.49609375)
[INFO] [stdout] [2m2026-06-13T09:50:27.395494Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 577 is a subharmonic of 43 (0.52441406 vs 0.50390625)
[INFO] [stdout] [2m2026-06-13T09:50:27.395509Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 934 is a subharmonic of 43 (0.53808594 vs 0.52441406)
[INFO] [stdout] [2m2026-06-13T09:50:27.395524Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 43 with 178 (0.53808594 vs 0.9892578)
[INFO] [stdout] [2m2026-06-13T09:50:27.395540Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 357 is a subharmonic of 178 (0.99316406 vs 0.9892578)
[INFO] [stdout] [2m2026-06-13T09:50:27.396116Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.53876 at 44100hZ = 247.00519hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.396132Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (3)
[INFO] [stdout] [2m2026-06-13T09:50:27.396144Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.99316406
[INFO] [stdout] [2m2026-06-13T09:50:27.396154Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [247.00519, 123.31087, 246.95076]
[INFO] [stdout] [2m2026-06-13T09:50:27.396167Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 3
[INFO] [stdout] [2m2026-06-13T09:50:27.396178Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.95076
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.396587Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 51 with 138 (0.40722656 vs 0.5644531)
[INFO] [stdout] [2m2026-06-13T09:50:27.396613Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 357 is a subharmonic of 179 (0.99121094 vs 0.98535156)
[INFO] [stdout] [2m2026-06-13T09:50:27.397230Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.62439 at 44100hZ = 246.88678hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.397251Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [247.00519, 246.88678, 246.95076]
[INFO] [stdout] [2m2026-06-13T09:50:27.397265Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 4
[INFO] [stdout] [2m2026-06-13T09:50:27.397276Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.95076
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.398968Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 52 with 150 (0.4140625 vs 0.6845703)
[INFO] [stdout] [2m2026-06-13T09:50:27.399046Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 358 is a subharmonic of 181 (0.9873047 vs 0.98046875)
[INFO] [stdout] [2m2026-06-13T09:50:27.399611Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.00691 at 44100hZ = 246.3592hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.399625Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [247.00519, 246.88678, 246.3592]
[INFO] [stdout] [2m2026-06-13T09:50:27.399638Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 5
[INFO] [stdout] [2m2026-06-13T09:50:27.399648Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.88678
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.399922Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 70 with 149 (0.19726563 vs 0.6816406)
[INFO] [stdout] [2m2026-06-13T09:50:27.400468Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.07858 at 44100hZ = 246.2606hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.400481Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.2606, 246.88678, 246.3592]
[INFO] [stdout] [2m2026-06-13T09:50:27.400493Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 6
[INFO] [stdout] [2m2026-06-13T09:50:27.400504Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.3592
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.401145Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 351 is a subharmonic of 171 (0.9394531 vs 0.92285156)
[INFO] [stdout] [2m2026-06-13T09:50:27.401171Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 171 with 179 (0.9394531 vs 0.9873047)
[INFO] [stdout] [2m2026-06-13T09:50:27.401464Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.34215 at 44100hZ = 247.2775hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.401481Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.2606, 247.2775, 246.3592]
[INFO] [stdout] [2m2026-06-13T09:50:27.401494Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 7
[INFO] [stdout] [2m2026-06-13T09:50:27.401504Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.3592
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.401917Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 92 with 343 (0.13378906 vs 0.84472656)
[INFO] [stdout] [2m2026-06-13T09:50:27.401952Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 174 with 357 (0.95214844 vs 0.98339844)
[INFO] [stdout] [2m2026-06-13T09:50:27.402336Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 177.46011 at 44100hZ = 248.50655hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.402355Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.2606, 247.2775, 248.50655]
[INFO] [stdout] [2m2026-06-13T09:50:27.402368Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 8
[INFO] [stdout] [2m2026-06-13T09:50:27.402380Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 247.2775
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.402778Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 357 is a subharmonic of 176 (0.9794922 vs 0.9609375)
[INFO] [stdout] [2m2026-06-13T09:50:27.403402Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 716 is a subharmonic of 176 (0.984375 vs 0.9794922)
[INFO] [stdout] [2m2026-06-13T09:50:27.403420Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 179 is a subharmonic of 176 (0.9863281 vs 0.984375)
[INFO] [stdout] [2m2026-06-13T09:50:27.403433Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 359 is a subharmonic of 176 (0.99121094 vs 0.9863281)
[INFO] [stdout] [2m2026-06-13T09:50:27.404017Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.48502 at 44100hZ = 245.70296hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.404033Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.70296, 247.2775, 248.50655]
[INFO] [stdout] [2m2026-06-13T09:50:27.404047Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 9
[INFO] [stdout] [2m2026-06-13T09:50:27.404059Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 247.2775
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.405692Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.40694 at 44100hZ = 245.80989hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.405717Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.70296, 245.80989, 248.50655]
[INFO] [stdout] [2m2026-06-13T09:50:27.405732Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 10
[INFO] [stdout] [2m2026-06-13T09:50:27.405743Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.80989
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.406017Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 84 with 181 (0.12402344 vs 0.9404297)
[INFO] [stdout] [2m2026-06-13T09:50:27.406622Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.7008 at 44100hZ = 246.7812hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.406637Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.70296, 245.80989, 246.7812]
[INFO] [stdout] [2m2026-06-13T09:50:27.406650Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 11
[INFO] [stdout] [2m2026-06-13T09:50:27.406661Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.80989
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.408201Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 100 with 209 (0.2763672 vs 0.6455078)
[INFO] [stdout] [2m2026-06-13T09:50:27.408545Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.88342 at 44100hZ = 246.52927hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.408569Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.52927, 245.80989, 246.7812]
[INFO] [stdout] [2m2026-06-13T09:50:27.408581Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 12
[INFO] [stdout] [2m2026-06-13T09:50:27.408592Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.52927
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.409611Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 538 is a subharmonic of 178 (0.9824219 vs 0.9824219)
[INFO] [stdout] [2m2026-06-13T09:50:27.409639Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 179 is a subharmonic of 178 (0.98339844 vs 0.9824219)
[INFO] [stdout] [2m2026-06-13T09:50:27.410437Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 180.69453 at 44100hZ = 244.05829hZ
[INFO] [stderr] ALSA lib confmisc.c:855:(parse_card) cannot find card '0'
[INFO] [stdout] [2m2026-06-13T09:50:27.410484Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.52927, 244.05829, 246.7812]
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory
[INFO] [stdout] [2m2026-06-13T09:50:27.410499Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 13
[INFO] [stderr] ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings
[INFO] [stdout] [2m2026-06-13T09:50:27.410511Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.52927
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
[INFO] [stdout] 
[INFO] [stderr] ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name
[INFO] [stdout] 
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
[INFO] [stdout] [2m2026-06-13T09:50:27.410927Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 716 is a subharmonic of 179 (0.98046875 vs 0.98046875)
[INFO] [stderr] ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory
[INFO] [stdout] [2m2026-06-13T09:50:27.411224Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.33658 at 44100hZ = 245.90633hZ
[INFO] [stderr] ALSA lib pcm.c:2721:(snd_pcm_open_noupdate) Unknown PCM default
[INFO] [stdout] [2m2026-06-13T09:50:27.411242Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.52927, 244.05829, 245.90633]
[INFO] [stdout] [2m2026-06-13T09:50:27.411256Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 14
[INFO] [stdout] [2m2026-06-13T09:50:27.411267Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.90633
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.411533Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 536 is a subharmonic of 179 (0.99316406 vs 0.99121094)
[INFO] [stdout] [2m2026-06-13T09:50:27.411851Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.20088 at 44100hZ = 246.09254hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.411868Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.09254, 244.05829, 245.90633]
[INFO] [stdout] [2m2026-06-13T09:50:27.411881Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 15
[INFO] [stdout] [2m2026-06-13T09:50:27.411893Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.90633
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.412775Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.21141 at 44100hZ = 246.07808hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.413466Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.09254, 246.07808, 245.90633]
[INFO] [stdout] [2m2026-06-13T09:50:27.413509Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 16
[INFO] [stdout] [2m2026-06-13T09:50:27.413521Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.07808
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.413919Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 58 with 183 (0.39160156 vs 0.8769531)
[INFO] [stdout] [2m2026-06-13T09:50:27.413941Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 183 with 895 (0.8769531 vs 0.93847656)
[INFO] [stdout] [2m2026-06-13T09:50:27.414499Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 895.3624 at 44100hZ = 49.2538hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.414529Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.414542Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.09254, 246.07808, 246.269]
[INFO] [stdout] [2m2026-06-13T09:50:27.414566Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 17
[INFO] [stdout] [2m2026-06-13T09:50:27.414578Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.09254
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.415020Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 359 is a subharmonic of 177 (0.9404297 vs 0.9355469)
[INFO] [stdout] [2m2026-06-13T09:50:27.415050Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 181 is a subharmonic of 177 (0.94921875 vs 0.9404297)
[INFO] [stdout] [2m2026-06-13T09:50:27.415597Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.625 at 44100hZ = 245.51149hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.415611Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.51149, 246.07808, 246.269]
[INFO] [stdout] [2m2026-06-13T09:50:27.415624Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 18
[INFO] [stdout] [2m2026-06-13T09:50:27.415635Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.07808
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.416278Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 53 with 177 (0.34375 vs 0.921875)
[INFO] [stdout] [2m2026-06-13T09:50:27.416307Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 360 is a subharmonic of 177 (0.9277344 vs 0.921875)
[INFO] [stdout] [2m2026-06-13T09:50:27.416324Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 897 is a subharmonic of 177 (0.9345703 vs 0.9277344)
[INFO] [stdout] [2m2026-06-13T09:50:27.416338Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 179 is a subharmonic of 177 (0.94140625 vs 0.9345703)
[INFO] [stdout] [2m2026-06-13T09:50:27.416925Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.15118 at 44100hZ = 246.16081hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.416943Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.51149, 246.16081, 246.269]
[INFO] [stdout] [2m2026-06-13T09:50:27.416956Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 19
[INFO] [stdout] [2m2026-06-13T09:50:27.416967Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.16081
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.417238Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 362 is a subharmonic of 183 (0.9189453 vs 0.9160156)
[INFO] [stdout] [2m2026-06-13T09:50:27.417259Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 183 with 537 (0.9189453 vs 0.9501953)
[INFO] [stdout] [2m2026-06-13T09:50:27.418063Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.81647 at 44100hZ = 246.62158hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.418086Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.51149, 246.16081, 246.62158]
[INFO] [stdout] [2m2026-06-13T09:50:27.418099Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 20
[INFO] [stdout] [2m2026-06-13T09:50:27.418109Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.16081
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.418496Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 46 with 175 (0.29882813 vs 0.8466797)
[INFO] [stdout] [2m2026-06-13T09:50:27.418514Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 175 with 358 (0.8466797 vs 0.92285156)
[INFO] [stdout] [2m2026-06-13T09:50:27.418528Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 716 is a subharmonic of 358 (0.9375 vs 0.92285156)
[INFO] [stdout] [2m2026-06-13T09:50:27.419917Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 358.0625 at 44100hZ = 123.16286hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.419940Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.419951Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.32571, 246.16081, 246.62158]
[INFO] [stdout] [2m2026-06-13T09:50:27.419965Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 21
[INFO] [stdout] [2m2026-06-13T09:50:27.419977Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.32571
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.420362Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 46 with 171 (0.27539063 vs 0.8095703)
[INFO] [stdout] [2m2026-06-13T09:50:27.420381Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 171 with 353 (0.8095703 vs 0.84375)
[INFO] [stdout] [2m2026-06-13T09:50:27.420395Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 353 with 717 (0.84375 vs 0.92089844)
[INFO] [stdout] [2m2026-06-13T09:50:27.421010Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 359.67178 at 44100hZ = 122.611786hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.421025Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.421036Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.32571, 245.22357, 246.62158]
[INFO] [stdout] [2m2026-06-13T09:50:27.421049Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 22
[INFO] [stdout] [2m2026-06-13T09:50:27.421061Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.32571
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.421442Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 100 with 636 (0.5751953 vs 0.6044922)
[INFO] [stdout] [2m2026-06-13T09:50:27.421467Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 715 is a subharmonic of 182 (0.93359375 vs 0.9326172)
[INFO] [stdout] [2m2026-06-13T09:50:27.421481Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 897 is a subharmonic of 182 (0.9511719 vs 0.93359375)
[INFO] [stdout] test tests::test_from_files ... FAILED
[INFO] [stdout] [2m2026-06-13T09:50:27.423138Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.24347 at 44100hZ = 246.03407hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.423177Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.32571, 245.22357, 246.03407]
[INFO] [stdout] [2m2026-06-13T09:50:27.423209Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 23
[INFO] [stdout] [2m2026-06-13T09:50:27.423238Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.03407
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.423638Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 44 with 179 (0.19238281 vs 0.95214844)
[INFO] [stdout] [2m2026-06-13T09:50:27.424934Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.3195 at 44100hZ = 245.92975hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.425037Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.92975, 245.22357, 246.03407]
[INFO] [stdout] [2m2026-06-13T09:50:27.425070Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 24
[INFO] [stdout] [2m2026-06-13T09:50:27.425113Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.92975
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.425701Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 897 is a subharmonic of 178 (0.94140625 vs 0.93847656)
[INFO] [stdout] [2m2026-06-13T09:50:27.425748Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 179 is a subharmonic of 178 (0.94140625 vs 0.94140625)
[INFO] [stdout] [2m2026-06-13T09:50:27.426346Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.0091 at 44100hZ = 246.3562hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.426399Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.92975, 246.3562, 246.03407]
[INFO] [stdout] [2m2026-06-13T09:50:27.426444Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 25
[INFO] [stdout] [2m2026-06-13T09:50:27.426506Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.03407
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.426991Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 45 with 141 (0.17578125 vs 0.20507813)
[INFO] [stdout] [2m2026-06-13T09:50:27.427055Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 141 with 278 (0.20507813 vs 0.7314453)
[INFO] [stdout] test tests::test_with_microphone ... FAILED
[INFO] [stdout] [2m2026-06-13T09:50:27.427136Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 714 is a subharmonic of 357 (0.9453125 vs 0.94140625)
[INFO] [stdout] [2m2026-06-13T09:50:27.427728Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 357.0802 at 44100hZ = 123.50167hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.427750Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.427762Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.92975, 246.3562, 247.00334]
[INFO] [stdout] [2m2026-06-13T09:50:27.427803Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 26
[INFO] [stdout] [2m2026-06-13T09:50:27.427815Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.3562
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.428230Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 32 with 210 (0.29296875 vs 0.33398438)
[INFO] [stdout] [2m2026-06-13T09:50:27.428254Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 705 is a subharmonic of 346 (0.7246094 vs 0.70703125)
[INFO] [stdout] [2m2026-06-13T09:50:27.428274Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 719 is a subharmonic of 178 (0.9267578 vs 0.9238281)
[INFO] [stdout] [2m2026-06-13T09:50:27.428289Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 180 is a subharmonic of 178 (0.9355469 vs 0.9267578)
[INFO] [stdout] [2m2026-06-13T09:50:27.429619Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 180.71922 at 44100hZ = 244.02495hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.429644Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.02495, 246.3562, 247.00334]
[INFO] [stdout] [2m2026-06-13T09:50:27.429658Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 27
[INFO] [stdout] [2m2026-06-13T09:50:27.429668Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.3562
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.430250Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 39 with 82 (0.19921875 vs 0.7421875)
[INFO] [stdout] [2m2026-06-13T09:50:27.430275Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 268 is a subharmonic of 82 (0.75683594 vs 0.7421875)
[INFO] [stdout] [2m2026-06-13T09:50:27.430315Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 620 is a subharmonic of 82 (0.7685547 vs 0.75683594)
[INFO] [stdout] [2m2026-06-13T09:50:27.430336Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 801 is a subharmonic of 82 (0.79003906 vs 0.7685547)
[INFO] [stdout] [2m2026-06-13T09:50:27.430349Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 82 with 894 (0.79003906 vs 0.9082031)
[INFO] [stdout] [2m2026-06-13T09:50:27.431336Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 359.01334 at 44100hZ = 122.83666hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.431378Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.431390Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.02495, 245.67332, 247.00334]
[INFO] [stdout] [2m2026-06-13T09:50:27.431407Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 28
[INFO] [stdout] [2m2026-06-13T09:50:27.431419Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.67332
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.431800Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 48 with 145 (0.14550781 vs 0.23339844)
[INFO] [stdout] [2m2026-06-13T09:50:27.431826Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 361 is a subharmonic of 183 (0.92578125 vs 0.91503906)
[INFO] [stdout] [2m2026-06-13T09:50:27.431841Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 539 is a subharmonic of 183 (0.9433594 vs 0.92578125)
[INFO] [stdout] [2m2026-06-13T09:50:27.431859Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 717 is a subharmonic of 183 (0.9482422 vs 0.9433594)
[INFO] [stdout] [2m2026-06-13T09:50:27.432859Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.93088 at 44100hZ = 246.46388hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.432902Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.02495, 245.67332, 246.46388]
[INFO] [stdout] [2m2026-06-13T09:50:27.432915Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 29
[INFO] [stdout] [2m2026-06-13T09:50:27.432925Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.67332
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.435207Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 896.38196 at 44100hZ = 49.197777hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.435236Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.435247Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.98889, 245.67332, 246.46388]
[INFO] [stdout] [2m2026-06-13T09:50:27.435260Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 30
[INFO] [stdout] [2m2026-06-13T09:50:27.435270Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.98889
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.435728Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 42 with 91 (0.125 vs 0.85546875)
[INFO] [stdout] [2m2026-06-13T09:50:27.435750Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 91 with 179 (0.85546875 vs 0.9316406)
[INFO] [stdout] [2m2026-06-13T09:50:27.435771Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 898 is a subharmonic of 179 (0.9394531 vs 0.9316406)
[INFO] [stdout] [2m2026-06-13T09:50:27.436769Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.85263 at 44100hZ = 245.20074hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.436810Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.98889, 245.20074, 246.46388]
[INFO] [stdout] [2m2026-06-13T09:50:27.436824Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 31
[INFO] [stdout] [2m2026-06-13T09:50:27.436834Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.98889
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.439114Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 717 is a subharmonic of 182 (0.93847656 vs 0.9199219)
[INFO] [stdout] [2m2026-06-13T09:50:27.439181Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 359 is a subharmonic of 182 (0.93847656 vs 0.93847656)
[INFO] [stdout] [2m2026-06-13T09:50:27.439198Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 716 is a subharmonic of 182 (0.9394531 vs 0.93847656)
[INFO] [stdout] [2m2026-06-13T09:50:27.439304Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 358 is a subharmonic of 182 (0.94140625 vs 0.9394531)
[INFO] [stdout] [2m2026-06-13T09:50:27.440149Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.6923 at 44100hZ = 245.41953hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.440274Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [245.98889, 245.20074, 245.41953]
[INFO] [stdout] [2m2026-06-13T09:50:27.440426Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 32
[INFO] [stdout] [2m2026-06-13T09:50:27.440590Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.41953
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.440966Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 98 with 183 (0.81152344 vs 0.89941406)
[INFO] [stdout] [2m2026-06-13T09:50:27.441089Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 183 with 716 (0.89941406 vs 0.92285156)
[INFO] [stdout] [2m2026-06-13T09:50:27.441812Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 895.5994 at 44100hZ = 49.240765hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.441929Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.442076Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.20383, 245.20074, 245.41953]
[INFO] [stdout] [2m2026-06-13T09:50:27.442199Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 33
[INFO] [stdout] [2m2026-06-13T09:50:27.442368Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.41953
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.442919Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 537 is a subharmonic of 181 (0.9199219 vs 0.9160156)
[INFO] [stdout] [2m2026-06-13T09:50:27.443089Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 895 is a subharmonic of 181 (0.921875 vs 0.9199219)
[INFO] [stdout] [2m2026-06-13T09:50:27.444048Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.46667 at 44100hZ = 245.72807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.444230Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.20383, 245.72807, 245.41953]
[INFO] [stdout] [2m2026-06-13T09:50:27.444349Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 34
[INFO] [stdout] [2m2026-06-13T09:50:27.444532Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.72807
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.445131Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 46 with 184 (0.1875 vs 0.8095703)
[INFO] [stdout] [2m2026-06-13T09:50:27.445319Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 536 is a subharmonic of 184 (0.81152344 vs 0.8095703)
[INFO] [stdout] [2m2026-06-13T09:50:27.445502Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 723 is a subharmonic of 179 (0.8408203 vs 0.83984375)
[INFO] [stdout] [2m2026-06-13T09:50:27.445684Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 898 is a subharmonic of 179 (0.8457031 vs 0.8408203)
[INFO] [stdout] [2m2026-06-13T09:50:27.445843Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 713 is a subharmonic of 179 (0.86621094 vs 0.8457031)
[INFO] [stdout] [2m2026-06-13T09:50:27.446541Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.55682 at 44100hZ = 246.9802hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.446726Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.20383, 245.72807, 246.9802]
[INFO] [stdout] [2m2026-06-13T09:50:27.446748Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 35
[INFO] [stdout] [2m2026-06-13T09:50:27.446759Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.20383
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.447331Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 40 with 92 (0.19433594 vs 0.8388672)
[INFO] [stdout] [2m2026-06-13T09:50:27.447348Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 173 is a subharmonic of 92 (0.85253906 vs 0.8388672)
[INFO] [stdout] [2m2026-06-13T09:50:27.447362Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 92 with 537 (0.85253906 vs 0.88964844)
[INFO] [stdout] [2m2026-06-13T09:50:27.447456Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 897 is a subharmonic of 179 (0.91796875 vs 0.9169922)
[INFO] [stdout] [2m2026-06-13T09:50:27.448008Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 178.69363 at 44100hZ = 246.7911hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.448023Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.7911, 245.72807, 246.9802]
[INFO] [stdout] [2m2026-06-13T09:50:27.448036Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 36
[INFO] [stdout] [2m2026-06-13T09:50:27.448047Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.7911
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.448427Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 98 with 182 (0.7910156 vs 0.890625)
[INFO] [stdout] [2m2026-06-13T09:50:27.448448Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 541 is a subharmonic of 182 (0.89746094 vs 0.890625)
[INFO] [stdout] [2m2026-06-13T09:50:27.448470Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 894 is a subharmonic of 177 (0.9033203 vs 0.9033203)
[INFO] [stdout] [2m2026-06-13T09:50:27.448486Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 356 is a subharmonic of 177 (0.9169922 vs 0.9033203)
[INFO] [stdout] [2m2026-06-13T09:50:27.448500Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 897 is a subharmonic of 177 (0.9277344 vs 0.9169922)
[INFO] [stdout] [2m2026-06-13T09:50:27.450280Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.63394 at 44100hZ = 245.49927hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.450355Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.7911, 245.49927, 246.9802]
[INFO] [stdout] [2m2026-06-13T09:50:27.450610Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 37
[INFO] [stdout] [2m2026-06-13T09:50:27.450731Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.7911
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.451081Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 47 with 126 (0.14648438 vs 0.20898438)
[INFO] [stdout] [2m2026-06-13T09:50:27.451147Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 720 is a subharmonic of 182 (0.8847656 vs 0.8808594)
[INFO] [stdout] [2m2026-06-13T09:50:27.451216Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 357 is a subharmonic of 182 (0.89453125 vs 0.8847656)
[INFO] [stdout] [2m2026-06-13T09:50:27.451281Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 717 is a subharmonic of 182 (0.89941406 vs 0.89453125)
[INFO] [stdout] [2m2026-06-13T09:50:27.451734Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 179.28572 at 44100hZ = 245.97609hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.451904Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [246.7911, 245.49927, 245.97609]
[INFO] [stdout] [2m2026-06-13T09:50:27.451996Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 38
[INFO] [stdout] [2m2026-06-13T09:50:27.452043Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.97609
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.452564Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 354 is a subharmonic of 180 (0.86035156 vs 0.85546875)
[INFO] [stdout] [2m2026-06-13T09:50:27.452630Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 539 is a subharmonic of 180 (0.86816406 vs 0.86035156)
[INFO] [stdout] [2m2026-06-13T09:50:27.452696Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 902 is a subharmonic of 180 (0.8769531 vs 0.86816406)
[INFO] [stdout] [2m2026-06-13T09:50:27.452815Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 180 with 359 (0.8769531 vs 0.90722656)
[INFO] [stdout] [2m2026-06-13T09:50:27.453496Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 360.53333 at 44100hZ = 122.31879hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.454773Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.454854Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.63757, 245.49927, 245.97609]
[INFO] [stdout] [2m2026-06-13T09:50:27.454917Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 39
[INFO] [stdout] [2m2026-06-13T09:50:27.454988Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.49927
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.455477Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 187 with 365 (0.8144531 vs 0.8544922)
[INFO] [stdout] [2m2026-06-13T09:50:27.455648Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 178 with 359 (0.88964844 vs 0.91308594)
[INFO] [stdout] [2m2026-06-13T09:50:27.456101Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 895.1538 at 44100hZ = 49.265278hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.456120Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.456131Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.63757, 246.32639, 245.97609]
[INFO] [stdout] [2m2026-06-13T09:50:27.456145Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 40
[INFO] [stdout] [2m2026-06-13T09:50:27.456156Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.97609
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.456579Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 705 is a subharmonic of 76 (0.70410156 vs 0.6953125)
[INFO] [stdout] [2m2026-06-13T09:50:27.456601Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 76 with 445 (0.70410156 vs 0.8496094)
[INFO] [stdout] [2m2026-06-13T09:50:27.457099Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 717.7778 at 44100hZ = 61.43963hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.457115Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.457125Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.63757, 246.32639, 245.75851]
[INFO] [stdout] [2m2026-06-13T09:50:27.457138Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 41
[INFO] [stdout] [2m2026-06-13T09:50:27.457148Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.75851
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.457405Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 262 is a subharmonic of 81 (0.8105469 vs 0.8046875)
[INFO] [stdout] [2m2026-06-13T09:50:27.457425Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 718 is a subharmonic of 81 (0.8300781 vs 0.8105469)
[INFO] [stdout] [2m2026-06-13T09:50:27.457440Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 358 is a subharmonic of 181 (0.8925781 vs 0.88671875)
[INFO] [stdout] [2m2026-06-13T09:50:27.457968Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 180.30833 at 44100hZ = 244.58104hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.457985Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.58104, 246.32639, 245.75851]
[INFO] [stdout] [2m2026-06-13T09:50:27.457998Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 42
[INFO] [stdout] [2m2026-06-13T09:50:27.458009Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.75851
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.458395Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 52 with 354 (0.25683594 vs 0.8232422)
[INFO] [stdout] [2m2026-06-13T09:50:27.459525Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 356.06668 at 44100hZ = 123.8532hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.459594Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.459645Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.58104, 247.7064, 245.75851]
[INFO] [stdout] [2m2026-06-13T09:50:27.459682Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 43
[INFO] [stdout] [2m2026-06-13T09:50:27.459736Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.75851
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.465442Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 173 with 895 (0.81152344 vs 0.85546875)
[INFO] [stdout] [2m2026-06-13T09:50:27.466070Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 893.1944 at 44100hZ = 49.373352hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.466138Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.466197Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [244.58104, 247.7064, 246.86676]
[INFO] [stdout] [2m2026-06-13T09:50:27.466270Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 44
[INFO] [stdout] [2m2026-06-13T09:50:27.466329Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.86676
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.466971Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 91 with 538 (0.7529297 vs 0.8222656)
[INFO] [stdout] [2m2026-06-13T09:50:27.467632Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 544.89087 at 44100hZ = 80.93364hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.467685Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.467733Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [242.80092, 247.7064, 246.86676]
[INFO] [stdout] [2m2026-06-13T09:50:27.467765Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 45
[INFO] [stdout] [2m2026-06-13T09:50:27.467825Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 246.86676
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.468286Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 91 with 898 (0.7861328 vs 0.8574219)
[INFO] [stdout] [2m2026-06-13T09:50:27.468832Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 896.50793 at 44100hZ = 49.190865hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.468884Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Checking for harmonics & subharmonics
[INFO] [stdout] [2m2026-06-13T09:50:27.468930Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [242.80092, 245.95432, 246.86676]
[INFO] [stdout] [2m2026-06-13T09:50:27.468958Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 46
[INFO] [stdout] [2m2026-06-13T09:50:27.469013Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.95432
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.473876Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 81.94286 at 44100hZ = 538.1799hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.474100Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.474137Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.474646Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 136 with 539 (0.4560547 vs 0.57128906)
[INFO] [stdout] [2m2026-06-13T09:50:27.474759Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 97 with 719 (0.6591797 vs 0.7158203)
[INFO] [stdout] [2m2026-06-13T09:50:27.475360Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 894.1667 at 44100hZ = 49.319664hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.475428Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.475751Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.476377Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 358 with 711 (0.6484375 vs 0.6816406)
[INFO] [stdout] [2m2026-06-13T09:50:27.477258Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 89.9 at 44100hZ = 490.54504hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.477474Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.477693Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.478308Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 88 with 178 (0.7011719 vs 0.74121094)
[INFO] [stdout] [2m2026-06-13T09:50:27.479279Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 175.37143 at 44100hZ = 251.46628hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.479458Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [242.80092, 245.95432, 251.46628]
[INFO] [stdout] [2m2026-06-13T09:50:27.479624Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.479770Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 245.95432
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.480889Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 367.80002 at 44100hZ = 119.902115hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.480962Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.480994Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.481503Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 93 with 535 (0.51953125 vs 0.5966797)
[INFO] [stdout] [2m2026-06-13T09:50:27.482370Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 172.85713 at 44100hZ = 255.12398hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.482490Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.482627Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.483113Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m Replacing 147 with 451 (0.35351563 vs 0.47558594)
[INFO] [stdout] [2m2026-06-13T09:50:27.483829Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 736.7778 at 44100hZ = 59.855225hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.483941Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.484081Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.485189Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 437.5 at 44100hZ = 100.8hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.485216Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.485237Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Unconfident shift at 0.8388672
[INFO] [stdout] [2m2026-06-13T09:50:27.485247Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.486997Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 198.36363 at 44100hZ = 222.31898hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.487085Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.487124Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.488666Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 348.4 at 44100hZ = 126.57864hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.488731Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.488760Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.490031Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 31 at 44100hZ = 1422.5807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.490240Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.490365Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Unconfident shift at 0.80078125
[INFO] [stdout] [2m2026-06-13T09:50:27.490514Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.492432Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 16.5 at 44100hZ = 2672.7273hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.492680Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.492840Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Unconfident shift at 0.8125
[INFO] [stdout] [2m2026-06-13T09:50:27.493016Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.494496Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 31 at 44100hZ = 1422.5807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.494658Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.494672Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.90625
[INFO] [stdout] [2m2026-06-13T09:50:27.494683Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1422.5807, 245.95432, 251.46628]
[INFO] [stdout] [2m2026-06-13T09:50:27.494695Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.494705Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 251.46628
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.495159Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 871.0833 at 44100hZ = 50.626617hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.495171Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.495181Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.495909Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 31 at 44100hZ = 1422.5807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.495925Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.495936Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.497563Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 31 at 44100hZ = 1422.5807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.497705Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.497862Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.9375
[INFO] [stdout] [2m2026-06-13T09:50:27.498032Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1422.5807, 1422.5807, 251.46628]
[INFO] [stdout] [2m2026-06-13T09:50:27.498188Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift detected, reset frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.498332Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1422.5807
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.499990Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 31 at 44100hZ = 1422.5807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.500181Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1422.5807, 1422.5807, 1422.5807]
[INFO] [stdout] [2m2026-06-13T09:50:27.500318Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.500449Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1422.5807
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.501306Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 31 at 44100hZ = 1422.5807hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.501444Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1422.5807, 1422.5807, 1422.5807]
[INFO] [stdout] [2m2026-06-13T09:50:27.501565Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 1
[INFO] [stdout] [2m2026-06-13T09:50:27.501686Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1422.5807
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.503493Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.503674Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (2)
[INFO] [stdout] [2m2026-06-13T09:50:27.503799Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.9375
[INFO] [stdout] [2m2026-06-13T09:50:27.503933Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1422.5807, 1331.8793, 1422.5807]
[INFO] [stdout] [2m2026-06-13T09:50:27.504062Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 2
[INFO] [stdout] [2m2026-06-13T09:50:27.504188Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1422.5807
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.506573Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.506824Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (3)
[INFO] [stdout] [2m2026-06-13T09:50:27.507004Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.9375
[INFO] [stdout] [2m2026-06-13T09:50:27.507148Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1422.5807, 1331.8793, 1331.8793]
[INFO] [stdout] [2m2026-06-13T09:50:27.507287Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift detected, reset frames from 3
[INFO] [stdout] [2m2026-06-13T09:50:27.507416Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.509855Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.509912Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 1331.8793]
[INFO] [stdout] [2m2026-06-13T09:50:27.509943Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.509970Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.512718Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.512778Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 1331.8793]
[INFO] [stdout] [2m2026-06-13T09:50:27.512827Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 1
[INFO] [stdout] [2m2026-06-13T09:50:27.512866Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.515135Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 153 at 44100hZ = 288.2353hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.515188Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (2)
[INFO] [stdout] [2m2026-06-13T09:50:27.515221Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 1
[INFO] [stdout] [2m2026-06-13T09:50:27.515251Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 288.2353]
[INFO] [stdout] [2m2026-06-13T09:50:27.515283Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 2
[INFO] [stdout] [2m2026-06-13T09:50:27.515317Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.515718Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.516137Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.516583Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m 844 is a subharmonic of 173 (0.8310547 vs 0.8310547)
[INFO] [stdout] [2m2026-06-13T09:50:27.518564Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.518623Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 288.2353]
[INFO] [stdout] [2m2026-06-13T09:50:27.518654Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.518681Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.519071Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.521085Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.521141Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 288.2353]
[INFO] [stdout] [2m2026-06-13T09:50:27.521172Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.521199Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.522857Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 926 at 44100hZ = 47.62419hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.522925Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (1)
[INFO] [stdout] [2m2026-06-13T09:50:27.522953Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.9707031
[INFO] [stdout] [2m2026-06-13T09:50:27.522977Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 47.62419]
[INFO] [stdout] [2m2026-06-13T09:50:27.523003Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 1
[INFO] [stdout] [2m2026-06-13T09:50:27.523036Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.525326Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 33.11111 at 44100hZ = 1331.8793hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.525399Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1331.8793, 47.62419]
[INFO] [stdout] [2m2026-06-13T09:50:27.525431Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 2
[INFO] [stdout] [2m2026-06-13T09:50:27.525459Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1331.8793
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.525884Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::period_detector[0m[2m:[0m False correlation check...
[INFO] [stdout] [2m2026-06-13T09:50:27.525927Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.526394Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 87.9 at 44100hZ = 501.70648hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.526443Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.526474Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.527094Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 87.9 at 44100hZ = 501.70648hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.527131Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.527159Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.527520Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.529744Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 38.375 at 44100hZ = 1149.1857hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.529837Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (0)
[INFO] [stdout] [2m2026-06-13T09:50:27.529874Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 0.9375
[INFO] [stdout] [2m2026-06-13T09:50:27.529906Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1149.1857, 47.62419]
[INFO] [stdout] [2m2026-06-13T09:50:27.529950Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift detected, reset frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.529981Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1149.1857
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.533497Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 38.375 at 44100hZ = 1149.1857hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.533704Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [1331.8793, 1149.1857, 1149.1857]
[INFO] [stdout] [2m2026-06-13T09:50:27.533752Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 0
[INFO] [stdout] [2m2026-06-13T09:50:27.533856Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1149.1857
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.537222Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Period 174.66667 at 44100hZ = 252.48091hZ
[INFO] [stdout] [2m2026-06-13T09:50:27.537266Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Not long enough since shift to check for harmonics (1)
[INFO] [stdout] [2m2026-06-13T09:50:27.537281Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Confident shift at 1
[INFO] [stdout] [2m2026-06-13T09:50:27.537292Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::median[0m[2m:[0m Median filter values: [252.48091, 1149.1857, 1149.1857]
[INFO] [stdout] [2m2026-06-13T09:50:27.537309Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::pitch_detector[0m[2m:[0m Shift not detected, increment frames from 1
[INFO] [stdout] [2m2026-06-13T09:50:27.537322Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Found Frequency: 1149.1857
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.537727Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] [2m2026-06-13T09:50:27.538431Z[0m [34mDEBUG[0m [2mbitstream_autocorrelation::tests[0m[2m:[0m Failed to find frequency
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] test tests::test_from_file ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_from_files stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_from_files' (19) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-subscriber-0.3.22/src/fmt/mod.rs:520:14:
[INFO] [stdout] Unable to install global subscriber: SetGlobalDefaultError("a global default trace dispatcher has already been set")
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5aafdb6e3c78 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5aafdb6e3c78 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5aafdb6e3c78 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5aafdb6e3c78 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5aafdb6f97ca - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5aafdb6f97ca - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5aafdb6e8902 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5aafdb6e8902 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5aafdb6c22f6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5aafdb6c22f6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5aafdb6dbd99 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5aafdb5447dc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5aafdb5447dc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5aafdb6dc012 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5aafdb6dc012 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5aafdb6c23b2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5aafdb6b74e9 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5aafdb6c30cd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5aafdb6f9f8c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5aafdb6f9d02 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5aafdb51e59b - <core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::error::Error + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send>>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5aafdb4e28c0 - <tracing_subscriber[e1bd5342748ce6e4]::fmt::SubscriberBuilder>::init
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-subscriber-0.3.22/src/fmt/mod.rs:520:14
[INFO] [stdout]   22:     0x5aafdb4a0ae6 - bitstream_autocorrelation[14b06627d3800389]::tests::test_from_files
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:137:14
[INFO] [stdout]   23:     0x5aafdb49da47 - bitstream_autocorrelation[14b06627d3800389]::tests::test_from_files::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:132:25
[INFO] [stdout]   24:     0x5aafdb4b3ba6 - <bitstream_autocorrelation[14b06627d3800389]::tests::test_from_files::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5aafdb5378bb - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5aafdb5378bb - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x5aafdb545135 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x5aafdb545135 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x5aafdb545135 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x5aafdb545135 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5aafdb545135 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5aafdb545135 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x5aafdb545135 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x5aafdb53fc94 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x5aafdb53fc94 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5aafdb5481b2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x5aafdb5481b2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x5aafdb5481b2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x5aafdb5481b2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5aafdb5481b2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5aafdb5481b2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x5aafdb5481b2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5aafdb6e359f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   44:     0x5aafdb6e359f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x725c8aebbaa4 - <unknown>
[INFO] [stdout]   46:     0x725c8af48a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_with_microphone stdout ----
[INFO] [stdout] Ok(DeviceDescription { name: "Default Audio Device", manufacturer: None, driver: Some("default"), device_type: Unknown, interface_type: Unknown, direction: Unknown, address: None, extended: ["Default Audio Device"] })
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::test_with_microphone' (20) panicked at src/lib.rs:71:64:
[INFO] [stdout] Failed to get device config.: DeviceNotAvailable
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5aafdb6e3c78 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5aafdb6e3c78 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5aafdb6e3c78 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5aafdb6e3c78 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5aafdb6f97ca - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5aafdb6f97ca - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5aafdb6e8902 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5aafdb6e8902 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5aafdb6c22f6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5aafdb6c22f6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5aafdb6dbd99 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5aafdb5447dc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5aafdb5447dc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5aafdb6dc012 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5aafdb6dc012 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout]   15:     0x5aafdb6c23b2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5aafdb6b74e9 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5aafdb6c30cd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5aafdb6f9f8c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5aafdb6f9d02 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5aafdb48da2f - <core[28b146924c2f19c6]::result::Result<cpal[243afa995033aac0]::SupportedStreamConfig, cpal[243afa995033aac0]::error::DefaultStreamConfigError>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5aafdb49de87 - bitstream_autocorrelation[14b06627d3800389]::tests::test_with_microphone::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:71:64
[INFO] [stdout]   22:     0x5aafdb4a1772 - <core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x5aafdb4f248d - <tokio[cb2f79c8f8467551]::runtime::park::CachedParkThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/park.rs:284:71
[INFO] [stdout]   24:     0x5aafdb4efe32 - tokio[cb2f79c8f8467551]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[cb2f79c8f8467551]::runtime::park::CachedParkThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   25:     0x5aafdb4efe32 - tokio[cb2f79c8f8467551]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[cb2f79c8f8467551]::runtime::park::CachedParkThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   26:     0x5aafdb4efe32 - <tokio[cb2f79c8f8467551]::runtime::park::CachedParkThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/park.rs:284:31
[INFO] [stdout]   27:     0x5aafdb4c0e24 - <tokio[cb2f79c8f8467551]::runtime::context::blocking::BlockingRegionGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   28:     0x5aafdb4dad48 - <tokio[cb2f79c8f8467551]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/multi_thread/mod.rs:89:22
[INFO] [stdout]   29:     0x5aafdb4f20ab - tokio[cb2f79c8f8467551]::runtime::context::runtime::enter_runtime::<<tokio[cb2f79c8f8467551]::runtime::scheduler::multi_thread::MultiThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   30:     0x5aafdb4d9334 - <tokio[cb2f79c8f8467551]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/multi_thread/mod.rs:88:9
[INFO] [stdout]   31:     0x5aafdb4e4914 - <tokio[cb2f79c8f8467551]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:370:50
[INFO] [stdout]   32:     0x5aafdb4e4b0f - <tokio[cb2f79c8f8467551]::runtime::runtime::Runtime>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   33:     0x5aafdb4a11d3 - bitstream_autocorrelation[14b06627d3800389]::tests::test_with_microphone
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:120:27
[INFO] [stdout]   34:     0x5aafdb49da77 - bitstream_autocorrelation[14b06627d3800389]::tests::test_with_microphone::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:67:36
[INFO] [stdout]   35:     0x5aafdb4b3be6 - <bitstream_autocorrelation[14b06627d3800389]::tests::test_with_microphone::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5aafdb5378bb - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x5aafdb5378bb - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   38:     0x5aafdb545135 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   39:     0x5aafdb545135 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x5aafdb545135 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5aafdb545135 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5aafdb545135 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5aafdb545135 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   44:     0x5aafdb545135 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   45:     0x5aafdb53fc94 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   46:     0x5aafdb53fc94 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   47:     0x5aafdb5481b2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   48:     0x5aafdb5481b2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x5aafdb5481b2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   50:     0x5aafdb5481b2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x5aafdb5481b2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x5aafdb5481b2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   53:     0x5aafdb5481b2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   54:     0x5aafdb6e359f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   55:     0x5aafdb6e359f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   56:     0x725c8aebbaa4 - <unknown>
[INFO] [stdout]   57:     0x725c8af48a64 - clone
[INFO] [stdout]   58:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::test_from_files
[INFO] [stdout]     tests::test_with_microphone
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "23fe70f21bd94653c8b541e70b6a9d0c2a448ad96cd0aabc54e0a85cb50c7ad1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "23fe70f21bd94653c8b541e70b6a9d0c2a448ad96cd0aabc54e0a85cb50c7ad1", kill_on_drop: false }`
[INFO] [stdout] 23fe70f21bd94653c8b541e70b6a9d0c2a448ad96cd0aabc54e0a85cb50c7ad1
